**** dT 0.000 * top TEST ../../../../bin/vinyltest/vtest2/tests/a02014.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** top extmacro def pwd=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest **** top extmacro def date(...) **** top extmacro def string(...) **** top extmacro def localhost=127.0.0.1 **** top extmacro def bad_backend=127.0.0.1:47627 **** top extmacro def listen_addr=127.0.0.1:0 **** top extmacro def bad_ip=192.0.2.255 **** top extmacro def topbuild=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub **** top extmacro def topsrc=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/../.. **** top macro def testdir=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/../../../../bin/vinyltest/vtest2/tests **** top macro def tmpdir=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.309370.4b0fd069 **** top macro def vtcid=vtc.309370.4b0fd069 **** dT 0.001 ** top === vtest "priority" * top VTEST priority ** top === server s1 { ** s1 Starting server **** s1 macro def s1_addr=127.0.0.1 **** s1 macro def s1_port=50565 **** s1 macro def s1_sock=127.0.0.1:50565 * s1 Listen on 127.0.0.1:50565 **** dT 0.002 ** top === client c1 -connect ${s1_sock} { ** c1 Starting client ** c1 Waiting for client ** s1 Started on 127.0.0.1:50565 (1 iterations) ** c1 Started on 127.0.0.1:50565 (1 iterations) *** c1 Connect to 127.0.0.1:50565 *** c1 connected fd 5 from 127.0.0.1 38433 to 127.0.0.1:50565 *** s1 accepted fd 4 127.0.0.1 38433 **** dT 0.003 ** c1 === stream 1 { **** c1 Not in H/2 mode, do what's needed ** c1 === txpri **** c1 txpri|PRI * HTTP/2.0\r **** c1 txpri|\r **** c1 txpri|SM\r **** c1 txpri|\r ** s1 === stream 1 { **** s1 Not in H/2 mode, do what's needed ** s1 === rxpri **** dT 0.013 ** s1 === stream 0 { ** c1 === stream 0 { ** s1 Starting stream 0 (fffffc7feedca010) ** c1 Starting stream 0 (fffffc7feedc8010) ** s1 Waiting for stream 0 ** c1 Waiting for stream 0 ** s1.0 === txsettings *** s1.0 tx: stream: 0, type: SETTINGS (4), flags: 0x00, size: 0 ** s1.0 === rxsettings ** c1.0 === txsettings *** c1.0 tx: stream: 0, type: SETTINGS (4), flags: 0x00, size: 0 ** c1.0 === rxsettings *** s1 rx: stream: 0, type: SETTINGS (4), flags: 0x00, size: 0 **** dT 0.014 *** c1 rx: stream: 0, type: SETTINGS (4), flags: 0x00, size: 0 ** s1.0 === txsettings -ack *** s1.0 tx: stream: 0, type: SETTINGS (4), flags: 0x01, size: 0 ** s1.0 === rxsettings ** c1.0 === txsettings -ack *** c1.0 tx: stream: 0, type: SETTINGS (4), flags: 0x01, size: 0 ** c1.0 === rxsettings *** s1 rx: stream: 0, type: SETTINGS (4), flags: 0x01, size: 0 *** s1 flag: ACK *** c1 rx: stream: 0, type: SETTINGS (4), flags: 0x01, size: 0 *** c1 flag: ACK ** s1.0 === expect settings.ack == true ** c1.0 === expect settings.ack == true **** c1.0 EXPECT settings.ack (true) == "true" match ** c1.0 Ending stream 0 **** s1.0 EXPECT settings.ack (true) == "true" match ** s1.0 Ending stream 0 ** c1 Starting stream 1 (fffffc7feedc6010) ** s1 Starting stream 1 (fffffc7feedc4010) ** c1 Waiting for stream 1 ** s1 Waiting for stream 1 ** s1.1 === rxreq ** c1.1 === txreq -method GET -url /1 \ *** c1.1 tx: stream: 1, type: HEADERS (1), flags: 0x05, size: 73 ** c1.1 === rxresp *** s1 rx: stream: 1, type: HEADERS (1), flags: 0x05, size: 73 *** s1 flag: END_STREAM **** s1 header[ 0]: :method: GET **** s1 header[ 1]: :path: /1 **** s1 header[ 2]: :scheme: http **** s1 header[ 3]: :authority: localhost **** s1 header[ 4]: :scheme: http ** s1.1 === txresp *** s1.1 tx: stream: 1, type: HEADERS (1), flags: 0x05, size: 13 ** s1.1 === expect stream.weight == 16 **** s1.1 EXPECT stream.weight (16) == "16" match *** c1 rx: stream: 1, type: HEADERS (1), flags: 0x05, size: 13 ** s1.1 === expect stream.dependency == 0 *** c1 flag: END_STREAM **** s1.1 EXPECT stream.dependency (0) == "0" match ** s1.1 Ending stream 1 **** c1 header[ 0]: :status: 200 ** c1.1 === expect stream.weight == 16 **** c1.1 EXPECT stream.weight (16) == "16" match ** c1.1 === expect stream.dependency == 0 **** c1.1 EXPECT stream.dependency (0) == "0" match ** s1 === stream 3 { ** c1.1 Ending stream 1 ** c1 === stream 3 { ** s1 Starting stream 3 (fffffc7feedc2010) **** dT 0.015 ** c1 Starting stream 3 (fffffc7feedc0010) ** s1 Waiting for stream 3 ** c1 Waiting for stream 3 ** s1.3 === rxreq ** c1.3 === txreq -req GET -url /3 \ **** c1.3 stream->dependency: 5 **** c1.3 stream->weight: 123 *** c1.3 tx: stream: 3, type: HEADERS (1), flags: 0x25, size: 78 ** c1.3 === rxresp *** s1 rx: stream: 3, type: HEADERS (1), flags: 0x25, size: 78 *** s1 flag: END_STREAM **** s1.3 stream->dependency: 5 **** s1.3 stream->weight: 123 **** s1 header[ 0]: :method: GET **** s1 header[ 1]: :path: /3 **** s1 header[ 2]: :scheme: http **** s1 header[ 3]: :authority: localhost **** s1 header[ 4]: :scheme: http ** s1.3 === txresp *** s1.3 tx: stream: 3, type: HEADERS (1), flags: 0x05, size: 13 ** s1.3 === expect stream.weight == 123 **** s1.3 EXPECT stream.weight (123) == "123" match ** s1.3 === expect stream.dependency == 5 **** s1.3 EXPECT stream.dependency (5) == "5" match ** s1.3 === rxprio *** c1 rx: stream: 3, type: HEADERS (1), flags: 0x05, size: 13 *** c1 flag: END_STREAM **** c1 header[ 0]: :status: 200 ** c1.3 === expect stream.weight == 123 **** c1.3 EXPECT stream.weight (123) == "123" match ** c1.3 === expect stream.dependency == 5 **** c1.3 EXPECT stream.dependency (5) == "5" match ** c1.3 === txprio -weight 10 -stream 7 *** c1.3 tx: stream: 3, type: PRIORITY (2), flags: 0x00, size: 5 ** c1.3 === expect stream.weight == 10 **** c1.3 EXPECT stream.weight (10) == "10" match ** c1.3 === expect stream.dependency == 7 **** c1.3 EXPECT stream.dependency (7) == "7" match ** c1.3 Ending stream 3 *** s1 rx: stream: 3, type: PRIORITY (2), flags: 0x00, size: 5 *** s1.3 prio->stream: 7 *** s1.3 prio->weight: 10 ** c1 === stream 5 { ** s1.3 === expect prio.weight == 10 **** s1.3 EXPECT prio.weight (10) == "10" match ** s1.3 === expect prio.stream == 7 **** s1.3 EXPECT prio.stream (7) == "7" match ** s1.3 === expect stream.weight == 10 **** s1.3 EXPECT stream.weight (10) == "10" match ** s1.3 === expect stream.dependency == 7 **** s1.3 EXPECT stream.dependency (7) == "7" match ** s1.3 Ending stream 3 ** c1 Starting stream 5 (fffffc7feedbe010) ** c1 Waiting for stream 5 *** s1 shutting fd 4 (server run) **** dT 0.016 ** s1 Ending ** c1.5 === expect stream.weight == 16 **** c1.5 EXPECT stream.weight (16) == "16" match ** c1.5 === expect stream.dependency == 0 **** c1.5 EXPECT stream.dependency (0) == "0" match ** c1.5 Ending stream 5 ** c1 === stream 0 { ** c1 Starting stream 0 (fffffc7feedc8010) ** c1 Waiting for stream 0 ** c1.0 === expect stream.weight == **** c1.0 EXPECT stream.weight () == "" match ** c1.0 === expect stream.dependency == **** c1.0 EXPECT stream.dependency () == "" match ** c1.0 Ending stream 0 *** c1 closing fd 5 ** c1 Ending ** top === server s1 -wait ** s1 Waiting for server (3/-1) * top RESETTING after ../../../../bin/vinyltest/vtest2/tests/a02014.vtc * top TEST ../../../../bin/vinyltest/vtest2/tests/a02014.vtc completed Assert error in cleaner_do(), ../../../../bin/vinyltest/vtest2/src/vtc_main.c line 240: Condition((system(buf)) == 0) not true. errno = 11 (Resource temporarily unavailable) ../../../../build-aux/test-driver: line 119: 309370: Abort FAIL vtest2/tests/a02014.vtc (exit status: 262)